11-19 20:20:42 D/ModuleListener: ModuleListener.testStarted(com.google.android.rvis.tvts.TvtsRvisTest#testRvisLaunchLegacyAudioRecord) on SKBFXYZO0DTQ
11-19 20:20:42 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-19 20:20:42 D/DeviceHostUtils: apiLevel: 31
11-19 20:20:42 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -c, -b, all] with timeout: 2m 0s
11-19 20:20:43 D/RunUtil: Running command null without timeout.
11-19 20:20:43 D/AppRunnerUtils: Checking service: com.google.android.tv.remote.service/.RemoteService
11-19 20:20:43 D/AppRunnerUtils: Running the following command: dumpsys activity service com.google.android.tv.remote.service/.RemoteService
11-19 20:20:43 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, dumpsys, activity, service, com.google.android.tv.remote.service/.RemoteService] with timeout: 2m 0s
11-19 20:20:43 I/AppRunnerUtils: Service PID = 9619
11-19 20:20:43 D/NativeDevice: Using property ro.build.version.sdk=31 from cache.
11-19 20:20:44 D/DeviceHostUtils: apiLevel: 31
11-19 20:20:44 I/TvtsRvisTest: KEYCODE_TO_VP_SHOWN	KEYCODE_TO_READING (In Milliseconds)
11-19 20:20:44 D/RunUtil: Running command null without timeout.
11-19 20:20:44 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -c, -b, all] with timeout: 2m 0s
11-19 20:20:44 D/ListInstrumentationParser: instrumentation:com.google.android.resolution.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.resolution.tvts)
11-19 20:20:44 D/ListInstrumentationParser: instrumentation:com.google.android.rvis.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.rvis.tvts)
11-19 20:20:44 D/ListInstrumentationParser: 
11-19 20:20:44 I/RemoteAndroidTestRunner: Running am instrument -w -r --abi armeabi-v7a  -e timeout_msec 20000 -e class 'com.google.android.rvis.tvts.TvtsRvisDeviceTest#sendAssistantKeycode' com.google.android.rvis.tvts/androidx.test.runner.AndroidJUnitRunner on foxconn-bfx_at100-SKBFXYZO0DTQ
11-19 20:20:46 I/BaseHostJUnit4Test: Test com.google.android.rvis.tvts.TvtsRvisDeviceTest#sendAssistantKeycode: PASSED
11-19 20:20:46 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
11-19 20:20:46 I/TvtsRvisTest:                  87	               281
11-19 20:20:46 I/KeyCodeUtils: Sending keyevent KEYCODE_BACK - intValue: 4
11-19 20:20:47 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:20:47 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:20:47 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:20:47 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:20:47 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:20:47 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:20:47 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:20:48 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:20:48 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:20:48 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:20:48 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:20:48 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:20:48 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:20:48 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:20:49 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:20:49 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:20:49 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:20:49 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:20:49 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:20:49 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:20:49 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:20:50 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:20:50 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:20:50 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:20:50 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:20:50 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:20:50 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:20:50 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:20:51 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:20:51 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:20:51 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:20:51 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:20:51 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:20:51 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:20:52 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:20:52 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:20:52 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:20:52 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:20:52 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:20:52 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:20:52 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:20:53 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:20:53 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:20:53 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:20:53 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:20:53 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:20:53 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:20:53 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:20:54 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:20:54 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:20:54 E/TvtsRvisTest: AudioTrack was not paused or stopped
11-19 20:20:54 D/RunUtil: Running command null without timeout.
11-19 20:20:54 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -c, -b, all] with timeout: 2m 0s
11-19 20:20:54 D/ListInstrumentationParser: instrumentation:com.google.android.resolution.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.resolution.tvts)
11-19 20:20:54 D/ListInstrumentationParser: instrumentation:com.google.android.rvis.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.rvis.tvts)
11-19 20:20:54 D/ListInstrumentationParser: 
11-19 20:20:54 I/RemoteAndroidTestRunner: Running am instrument -w -r --abi armeabi-v7a  -e timeout_msec 20000 -e class 'com.google.android.rvis.tvts.TvtsRvisDeviceTest#sendAssistantKeycode' com.google.android.rvis.tvts/androidx.test.runner.AndroidJUnitRunner on foxconn-bfx_at100-SKBFXYZO0DTQ
11-19 20:20:56 I/BaseHostJUnit4Test: Test com.google.android.rvis.tvts.TvtsRvisDeviceTest#sendAssistantKeycode: PASSED
11-19 20:20:56 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
11-19 20:20:57 I/TvtsRvisTest:                  15	               154
11-19 20:20:57 I/KeyCodeUtils: Sending keyevent KEYCODE_BACK - intValue: 4
11-19 20:20:57 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:20:57 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:20:57 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:20:57 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:20:57 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:20:57 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:20:58 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:20:58 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:20:58 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:20:58 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:20:58 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:20:58 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:20:58 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:20:59 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:20:59 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:20:59 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:20:59 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:20:59 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:20:59 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:20:59 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:00 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:00 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:00 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:00 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:00 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:00 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:00 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:01 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:01 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:01 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:01 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:01 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:01 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:01 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:02 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:02 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:02 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:02 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:02 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:02 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:02 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:03 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:03 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:03 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:03 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:03 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:03 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:03 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:04 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:04 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:04 E/TvtsRvisTest: AudioTrack was not paused or stopped
11-19 20:21:04 D/RunUtil: Running command null without timeout.
11-19 20:21:04 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -c, -b, all] with timeout: 2m 0s
11-19 20:21:04 D/ListInstrumentationParser: instrumentation:com.google.android.resolution.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.resolution.tvts)
11-19 20:21:04 D/ListInstrumentationParser: instrumentation:com.google.android.rvis.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.rvis.tvts)
11-19 20:21:04 D/ListInstrumentationParser: 
11-19 20:21:04 I/RemoteAndroidTestRunner: Running am instrument -w -r --abi armeabi-v7a  -e timeout_msec 20000 -e class 'com.google.android.rvis.tvts.TvtsRvisDeviceTest#sendAssistantKeycode' com.google.android.rvis.tvts/androidx.test.runner.AndroidJUnitRunner on foxconn-bfx_at100-SKBFXYZO0DTQ
11-19 20:21:06 I/BaseHostJUnit4Test: Test com.google.android.rvis.tvts.TvtsRvisDeviceTest#sendAssistantKeycode: PASSED
11-19 20:21:06 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
11-19 20:21:07 I/TvtsRvisTest:                   9	               140
11-19 20:21:07 I/KeyCodeUtils: Sending keyevent KEYCODE_BACK - intValue: 4
11-19 20:21:07 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:07 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:07 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:07 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:07 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:07 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:08 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:08 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:08 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:08 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:08 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:08 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:08 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:09 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:09 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:09 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:09 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:09 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:09 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:09 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:10 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:10 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:10 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:10 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:10 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:10 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:10 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:11 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:11 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:11 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:11 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:11 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:11 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:11 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:12 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:12 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:12 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:12 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:12 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:12 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:13 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:13 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:13 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:13 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:13 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:13 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:13 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:14 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:14 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:14 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:14 E/TvtsRvisTest: AudioTrack was not paused or stopped
11-19 20:21:14 D/RunUtil: Running command null without timeout.
11-19 20:21:14 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -c, -b, all] with timeout: 2m 0s
11-19 20:21:14 D/ListInstrumentationParser: instrumentation:com.google.android.resolution.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.resolution.tvts)
11-19 20:21:14 D/ListInstrumentationParser: instrumentation:com.google.android.rvis.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.rvis.tvts)
11-19 20:21:14 D/ListInstrumentationParser: 
11-19 20:21:14 I/RemoteAndroidTestRunner: Running am instrument -w -r --abi armeabi-v7a  -e timeout_msec 20000 -e class 'com.google.android.rvis.tvts.TvtsRvisDeviceTest#sendAssistantKeycode' com.google.android.rvis.tvts/androidx.test.runner.AndroidJUnitRunner on foxconn-bfx_at100-SKBFXYZO0DTQ
11-19 20:21:17 I/BaseHostJUnit4Test: Test com.google.android.rvis.tvts.TvtsRvisDeviceTest#sendAssistantKeycode: PASSED
11-19 20:21:17 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
11-19 20:21:17 I/TvtsRvisTest:                  11	               185
11-19 20:21:17 I/KeyCodeUtils: Sending keyevent KEYCODE_BACK - intValue: 4
11-19 20:21:17 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:17 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:17 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:17 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:18 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:18 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:18 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:18 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:18 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:18 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:18 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:18 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:19 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:19 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:19 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:19 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:19 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:19 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:20 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:20 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:20 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:20 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:20 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:20 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:20 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:21 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:21 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:21 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:21 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:21 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:21 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:21 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:22 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:22 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:22 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:22 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:22 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:22 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:23 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:23 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:23 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:23 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:23 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:23 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:23 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:24 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:24 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:24 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:24 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:24 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:24 E/TvtsRvisTest: AudioTrack was not paused or stopped
11-19 20:21:24 D/RunUtil: Running command null without timeout.
11-19 20:21:24 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -c, -b, all] with timeout: 2m 0s
11-19 20:21:25 D/ListInstrumentationParser: instrumentation:com.google.android.resolution.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.resolution.tvts)
11-19 20:21:25 D/ListInstrumentationParser: instrumentation:com.google.android.rvis.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.rvis.tvts)
11-19 20:21:25 D/ListInstrumentationParser: 
11-19 20:21:25 I/RemoteAndroidTestRunner: Running am instrument -w -r --abi armeabi-v7a  -e timeout_msec 20000 -e class 'com.google.android.rvis.tvts.TvtsRvisDeviceTest#sendAssistantKeycode' com.google.android.rvis.tvts/androidx.test.runner.AndroidJUnitRunner on foxconn-bfx_at100-SKBFXYZO0DTQ
11-19 20:21:27 I/BaseHostJUnit4Test: Test com.google.android.rvis.tvts.TvtsRvisDeviceTest#sendAssistantKeycode: PASSED
11-19 20:21:27 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
11-19 20:21:27 I/TvtsRvisTest:                  10	               168
11-19 20:21:27 I/KeyCodeUtils: Sending keyevent KEYCODE_BACK - intValue: 4
11-19 20:21:27 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:27 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:28 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:28 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:28 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:28 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:28 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:28 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:29 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:29 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:29 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:29 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:29 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:29 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:29 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:29 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:30 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:30 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:30 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:30 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:30 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:30 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:31 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:31 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:31 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:31 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:31 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:31 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:31 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:32 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:32 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:32 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:32 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:32 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:32 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:32 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:33 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:33 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:33 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:33 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:33 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:33 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:34 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:34 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:34 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:34 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:34 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:34 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:34 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:35 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:35 E/TvtsRvisTest: AudioTrack was not paused or stopped
11-19 20:21:35 D/RunUtil: Running command null without timeout.
11-19 20:21:35 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -c, -b, all] with timeout: 2m 0s
11-19 20:21:35 D/ListInstrumentationParser: instrumentation:com.google.android.resolution.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.resolution.tvts)
11-19 20:21:35 D/ListInstrumentationParser: instrumentation:com.google.android.rvis.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.rvis.tvts)
11-19 20:21:35 D/ListInstrumentationParser: 
11-19 20:21:35 I/RemoteAndroidTestRunner: Running am instrument -w -r --abi armeabi-v7a  -e timeout_msec 20000 -e class 'com.google.android.rvis.tvts.TvtsRvisDeviceTest#sendAssistantKeycode' com.google.android.rvis.tvts/androidx.test.runner.AndroidJUnitRunner on foxconn-bfx_at100-SKBFXYZO0DTQ
11-19 20:21:37 I/BaseHostJUnit4Test: Test com.google.android.rvis.tvts.TvtsRvisDeviceTest#sendAssistantKeycode: PASSED
11-19 20:21:37 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
11-19 20:21:38 I/TvtsRvisTest:                  13	               155
11-19 20:21:38 I/KeyCodeUtils: Sending keyevent KEYCODE_BACK - intValue: 4
11-19 20:21:38 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:38 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:38 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:38 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:38 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:38 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:38 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:39 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:39 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:39 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:39 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:39 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:39 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:39 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:40 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:40 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:40 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:40 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:40 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:40 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:41 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:41 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:41 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:41 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:41 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:41 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:41 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:41 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:42 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:42 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:42 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:42 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:42 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:42 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:43 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:43 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:43 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:43 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:43 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:43 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:43 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:43 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:44 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:44 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:44 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:44 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:44 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:44 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:44 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:45 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:45 E/TvtsRvisTest: AudioTrack was not paused or stopped
11-19 20:21:45 D/RunUtil: Running command null without timeout.
11-19 20:21:45 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -c, -b, all] with timeout: 2m 0s
11-19 20:21:45 D/ListInstrumentationParser: instrumentation:com.google.android.resolution.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.resolution.tvts)
11-19 20:21:45 D/ListInstrumentationParser: instrumentation:com.google.android.rvis.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.rvis.tvts)
11-19 20:21:45 D/ListInstrumentationParser: 
11-19 20:21:45 I/RemoteAndroidTestRunner: Running am instrument -w -r --abi armeabi-v7a  -e timeout_msec 20000 -e class 'com.google.android.rvis.tvts.TvtsRvisDeviceTest#sendAssistantKeycode' com.google.android.rvis.tvts/androidx.test.runner.AndroidJUnitRunner on foxconn-bfx_at100-SKBFXYZO0DTQ
11-19 20:21:47 I/BaseHostJUnit4Test: Test com.google.android.rvis.tvts.TvtsRvisDeviceTest#sendAssistantKeycode: PASSED
11-19 20:21:47 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
11-19 20:21:48 I/TvtsRvisTest:                   9	               176
11-19 20:21:48 I/KeyCodeUtils: Sending keyevent KEYCODE_BACK - intValue: 4
11-19 20:21:48 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:48 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:48 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:48 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:48 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:48 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:49 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:49 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:49 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:49 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:49 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:49 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:49 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:50 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:50 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:50 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:50 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:50 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:50 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:50 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:51 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:51 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:51 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:51 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:51 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:51 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:52 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:52 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:52 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:52 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:52 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:52 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:52 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:53 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:53 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:53 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:53 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:53 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:53 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:53 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:54 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:54 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:54 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:54 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:54 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:54 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:55 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:55 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:55 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:55 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:55 E/TvtsRvisTest: AudioTrack was not paused or stopped
11-19 20:21:55 D/RunUtil: Running command null without timeout.
11-19 20:21:55 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -c, -b, all] with timeout: 2m 0s
11-19 20:21:55 D/ListInstrumentationParser: instrumentation:com.google.android.resolution.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.resolution.tvts)
11-19 20:21:55 D/ListInstrumentationParser: instrumentation:com.google.android.rvis.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.rvis.tvts)
11-19 20:21:55 D/ListInstrumentationParser: 
11-19 20:21:55 I/RemoteAndroidTestRunner: Running am instrument -w -r --abi armeabi-v7a  -e timeout_msec 20000 -e class 'com.google.android.rvis.tvts.TvtsRvisDeviceTest#sendAssistantKeycode' com.google.android.rvis.tvts/androidx.test.runner.AndroidJUnitRunner on foxconn-bfx_at100-SKBFXYZO0DTQ
11-19 20:21:58 I/BaseHostJUnit4Test: Test com.google.android.rvis.tvts.TvtsRvisDeviceTest#sendAssistantKeycode: PASSED
11-19 20:21:58 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
11-19 20:21:58 I/TvtsRvisTest:                  10	               165
11-19 20:21:58 I/KeyCodeUtils: Sending keyevent KEYCODE_BACK - intValue: 4
11-19 20:21:58 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:58 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:58 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:58 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:59 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:59 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:59 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:59 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:21:59 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:21:59 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:00 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:00 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:00 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:00 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:00 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:00 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:00 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:00 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:01 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:01 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:01 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:01 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:01 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:01 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:02 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:02 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:02 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:02 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:02 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:02 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:02 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:03 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:03 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:03 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:03 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:03 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:03 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:03 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:04 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:04 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:04 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:04 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:04 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:04 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:04 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:05 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:05 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:05 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:05 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:05 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:05 E/TvtsRvisTest: AudioTrack was not paused or stopped
11-19 20:22:05 D/RunUtil: Running command null without timeout.
11-19 20:22:05 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -c, -b, all] with timeout: 2m 0s
11-19 20:22:06 D/ListInstrumentationParser: instrumentation:com.google.android.resolution.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.resolution.tvts)
11-19 20:22:06 D/ListInstrumentationParser: instrumentation:com.google.android.rvis.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.rvis.tvts)
11-19 20:22:06 D/ListInstrumentationParser: 
11-19 20:22:06 I/RemoteAndroidTestRunner: Running am instrument -w -r --abi armeabi-v7a  -e timeout_msec 20000 -e class 'com.google.android.rvis.tvts.TvtsRvisDeviceTest#sendAssistantKeycode' com.google.android.rvis.tvts/androidx.test.runner.AndroidJUnitRunner on foxconn-bfx_at100-SKBFXYZO0DTQ
11-19 20:22:08 I/BaseHostJUnit4Test: Test com.google.android.rvis.tvts.TvtsRvisDeviceTest#sendAssistantKeycode: PASSED
11-19 20:22:08 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
11-19 20:22:08 I/TvtsRvisTest:                   8	               165
11-19 20:22:08 I/KeyCodeUtils: Sending keyevent KEYCODE_BACK - intValue: 4
11-19 20:22:08 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:08 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:09 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:09 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:09 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:09 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:09 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:09 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:09 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:10 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:10 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:10 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:10 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:10 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:10 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:10 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:11 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:11 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:11 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:11 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:11 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:11 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:11 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:12 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:12 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:12 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:12 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:12 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:12 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:12 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:13 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:13 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:13 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:13 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:13 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:13 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:14 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:14 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:14 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:14 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:14 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:14 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:14 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:14 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:15 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:15 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:15 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:15 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:15 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:15 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:16 E/TvtsRvisTest: AudioTrack was not paused or stopped
11-19 20:22:16 D/RunUtil: Running command null without timeout.
11-19 20:22:16 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -c, -b, all] with timeout: 2m 0s
11-19 20:22:16 D/ListInstrumentationParser: instrumentation:com.google.android.resolution.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.resolution.tvts)
11-19 20:22:16 D/ListInstrumentationParser: instrumentation:com.google.android.rvis.tvts/androidx.test.runner.AndroidJUnitRunner (target=com.google.android.rvis.tvts)
11-19 20:22:16 D/ListInstrumentationParser: 
11-19 20:22:16 I/RemoteAndroidTestRunner: Running am instrument -w -r --abi armeabi-v7a  -e timeout_msec 20000 -e class 'com.google.android.rvis.tvts.TvtsRvisDeviceTest#sendAssistantKeycode' com.google.android.rvis.tvts/androidx.test.runner.AndroidJUnitRunner on foxconn-bfx_at100-SKBFXYZO0DTQ
11-19 20:22:18 I/BaseHostJUnit4Test: Test com.google.android.rvis.tvts.TvtsRvisDeviceTest#sendAssistantKeycode: PASSED
11-19 20:22:18 D/RunUtil: Running command [logcat -d | grep -E 'Read non-zero bytes'] without timeout.
11-19 20:22:18 I/TvtsRvisTest:                  13	               170
11-19 20:22:18 I/KeyCodeUtils: Sending keyevent KEYCODE_BACK - intValue: 4
11-19 20:22:18 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:19 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:19 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:19 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:19 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:19 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:19 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:19 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:20 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:20 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:20 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:20 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:20 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:20 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:20 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:21 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:21 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:21 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:21 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:21 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:21 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:21 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:22 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:22 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:22 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:22 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:22 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:22 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:23 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:23 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:23 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:23 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:23 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:23 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:23 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:24 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:24 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:24 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:24 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:24 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:24 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:24 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:25 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:25 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:25 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:25 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:25 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:25 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:26 D/RunUtil: Running command [logcat -d | grep -E 'AudioTrack .* in .*ms'] without timeout.
11-19 20:22:26 D/RunUtil: operation failed, waiting for 200 ms
11-19 20:22:26 E/TvtsRvisTest: AudioTrack was not paused or stopped
11-19 20:22:26 I/TvtsRvisTest: numOfRuns: 10
11-19 20:22:26 I/TvtsRvisTest: numOfFailedRuns: 0
11-19 20:22:26 D/PrettyTestEventLogger: 
==================== com.google.android.rvis.tvts.TvtsRvisTest#testRvisLaunchLegacyAudioRecord ENDED: Tue Nov 19 20:22:26 KST 2024 ====================
